[INFO] cloning repository https://github.com/swiftugandan/nano-agent
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/swiftugandan/nano-agent" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fswiftugandan%2Fnano-agent", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fswiftugandan%2Fnano-agent'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] f08f38a580b028dfde261726338a8a683d85e82f
[INFO] testing swiftugandan/nano-agent against try#0abe69e6c16114311cc2700e972492e64d69c28f for pr-154065
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fswiftugandan%2Fnano-agent" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/swiftugandan/nano-agent
[INFO] finished tweaking git repo https://github.com/swiftugandan/nano-agent
[INFO] tweaked toml for git repo https://github.com/swiftugandan/nano-agent written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/swiftugandan/nano-agent on toolchain 0abe69e6c16114311cc2700e972492e64d69c28f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/swiftugandan/nano-agent already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 9da4e570bd7e1ad9ae94c11f43ec6de2a598f1f9af04b864e52dcf033d1b4d4d
[INFO] running `Command { std: "docker" "start" "-a" "9da4e570bd7e1ad9ae94c11f43ec6de2a598f1f9af04b864e52dcf033d1b4d4d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9da4e570bd7e1ad9ae94c11f43ec6de2a598f1f9af04b864e52dcf033d1b4d4d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9da4e570bd7e1ad9ae94c11f43ec6de2a598f1f9af04b864e52dcf033d1b4d4d", kill_on_drop: false }`
[INFO] [stdout] 9da4e570bd7e1ad9ae94c11f43ec6de2a598f1f9af04b864e52dcf033d1b4d4d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9b7b6f2f14a7781e9538320a556f82796d6d9559820d2d3ad2e45793ea640ec9
[INFO] running `Command { std: "docker" "start" "-a" "9b7b6f2f14a7781e9538320a556f82796d6d9559820d2d3ad2e45793ea640ec9", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling cc v1.2.57
[INFO] [stderr]    Compiling rustix v0.38.44
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling linux-raw-sys v0.4.15
[INFO] [stderr]    Compiling darling_core v0.23.0
[INFO] [stderr]    Compiling nix v0.31.2
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling strum_macros v0.26.4
[INFO] [stderr]    Compiling simd-adler32 v0.3.8
[INFO] [stderr]    Compiling rustls v0.23.37
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling icu_normalizer v2.1.1
[INFO] [stderr]    Compiling instability v0.3.12
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling derive_more-impl v2.1.1
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling vte v0.14.1
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling iana-time-zone v0.1.65
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling compact_str v0.8.1
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling strip-ansi-escapes v0.2.1
[INFO] [stderr]    Compiling unicode-truncate v1.1.0
[INFO] [stderr]    Compiling webpki-roots v0.26.11
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling lru v0.12.5
[INFO] [stderr]    Compiling clap_derive v4.6.0
[INFO] [stderr]    Compiling itertools v0.12.1
[INFO] [stderr]    Compiling cassowary v0.3.0
[INFO] [stderr]    Compiling derive_more v2.1.1
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling unicode-width v0.2.0
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling strum v0.26.3
[INFO] [stderr]    Compiling darling_macro v0.23.0
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling darling v0.23.0
[INFO] [stderr]    Compiling uuid v1.22.0
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling signal-hook-mio v0.2.5
[INFO] [stderr]    Compiling fd-lock v4.0.4
[INFO] [stderr]    Compiling crossterm v0.28.1
[INFO] [stderr]    Compiling crossterm v0.29.0
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling ctrlc v3.5.2
[INFO] [stderr]    Compiling reedline v0.37.0
[INFO] [stderr]    Compiling ratatui v0.29.0
[INFO] [stderr]    Compiling rustls-webpki v0.103.9
[INFO] [stderr]    Compiling ureq v2.12.1
[INFO] [stderr]    Compiling nano-agent v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 42.32s
[INFO] running `Command { std: "docker" "inspect" "9b7b6f2f14a7781e9538320a556f82796d6d9559820d2d3ad2e45793ea640ec9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9b7b6f2f14a7781e9538320a556f82796d6d9559820d2d3ad2e45793ea640ec9", kill_on_drop: false }`
[INFO] [stdout] 9b7b6f2f14a7781e9538320a556f82796d6d9559820d2d3ad2e45793ea640ec9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] fa6967b69abc13f9751bfd91be3950fcc410b45d3105f9ad0d65477dc9bb1324
[INFO] running `Command { std: "docker" "start" "-a" "fa6967b69abc13f9751bfd91be3950fcc410b45d3105f9ad0d65477dc9bb1324", kill_on_drop: false }`
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling nano-agent v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 15.89s
[INFO] running `Command { std: "docker" "inspect" "fa6967b69abc13f9751bfd91be3950fcc410b45d3105f9ad0d65477dc9bb1324", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "fa6967b69abc13f9751bfd91be3950fcc410b45d3105f9ad0d65477dc9bb1324", kill_on_drop: false }`
[INFO] [stdout] fa6967b69abc13f9751bfd91be3950fcc410b45d3105f9ad0d65477dc9bb1324
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 1c7a743a68c117484967c14ec22e4cf1c309faeb621416bc475379e19a2bed40
[INFO] running `Command { std: "docker" "start" "-a" "1c7a743a68c117484967c14ec22e4cf1c309faeb621416bc475379e19a2bed40", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.32s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/nano_agent-582147e6b9e53b9f)
[INFO] [stdout] running 40 tests
[INFO] [stdout] test handler::tests::test_handler_registry_extend ... ok
[INFO] [stdout] test context::tests::seed_collector_filters_empty ... ok
[INFO] [stdout] test handler::tests::test_handler_error_display ... ok
[INFO] [stdout] test context::tests::truncate_at_newline_boundary ... ok
[INFO] [stdout] test context::tests::projector_spills_above_threshold ... ok
[INFO] [stdout] test context::tests::projector_retrieve_missing_returns_none ... ok
[INFO] [stdout] test concurrency::tests::unknown_task_check ... ok
[INFO] [stdout] test handler::tests::test_with_retry_does_not_retry_validation_errors ... ok
[INFO] [stdout] test memory::tests::test_rotate_if_needed ... ok
[INFO] [stdout] test context::tests::projector_passthrough_below_threshold ... ok
[INFO] [stdout] test handler::tests::test_chain_with_middleware ... ok
[INFO] [stdout] test handler::tests::test_with_retry_succeeds_on_second_attempt ... ok
[INFO] [stdout] test memory_store::tests::test_evict_old ... ok
[INFO] [stdout] test concurrency::tests::run_and_check ... ok
[INFO] [stdout] test handler::tests::test_agent_context_mock_clone ... ok
[INFO] [stdout] test resilience::tests::context_guard_normal_passthrough ... ok
[INFO] [stdout] test resilience::tests::context_guard_overflow_triggers_truncation ... ok
[INFO] [stdout] test handler::tests::test_with_output_cap_passthrough_small ... ok
[INFO] [stdout] test handler::tests::test_handler_registry_route_and_contains ... ok
[INFO] [stdout] test memory_store::tests::test_save_and_recall ... ok
[INFO] [stdout] test resilience::tests::resilient_fatal_not_retried ... ok
[INFO] [stdout] test resilience::tests::resilient_success_path ... ok
[INFO] [stdout] test resilience::tests::resilient_overflow_not_retried ... ok
[INFO] [stdout] test tasks::tests::update_invalid_status ... ok
[INFO] [stdout] test tasks::tests::create_and_get ... ok
[INFO] [stdout] test tasks::tests::update_status ... ok
[INFO] [stdout] test tasks::tests::get_nonexistent ... ok
[INFO] [stdout] test resilience::tests::resilient_transient_then_success ... ok
[INFO] [stdout] test tasks::tests::list_all_ordered ... ok
[INFO] [stdout] test memory_store::tests::test_term_frequency ... ok
[INFO] [stdout] test tasks::tests::bind_worktree ... ok
[INFO] [stdout] test concurrency::tests::drain_notifications_clears ... ok
[INFO] [stdout] test tasks::tests::dependencies ... ok
[INFO] [stdout] test resilience::tests::resilient_max_retries_exhausted ... ok
[INFO] [stdout] test concurrency::tests::lane_concurrency_respected ... ok
[INFO] [stdout] test memory::tests::test_evict_old_sessions_preserves_recent ... ok
[INFO] [stdout] test handler::tests::test_handler_trait_closure_blanket_impl ... ok
[INFO] [stdout] test memory_store::tests::test_tokenize ... ok
[INFO] [stdout] test context::tests::projector_retrieve_returns_spilled_content ... ok
[INFO] [stdout] test memory::tests::test_evict_old_sessions_ignores_non_session_files ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 40 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/agent-80169fa38d1169ab)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_agent_core_headless.rs (/opt/rustwide/target/debug/deps/test_agent_core_headless-54cc4f6a630da91a)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test agent_core_emits_turn_finished ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_heartbeat.rs (/opt/rustwide/target/debug/deps/test_heartbeat-b4720af208fd98da)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test test_fires_after_minute_gap ... ok
[INFO] [stdout] test test_remove_nonexistent ... ok
[INFO] [stdout] test test_every_minute_matches ... ok
[INFO] [stdout] test test_prevents_double_fire_within_minute ... ok
[INFO] [stdout] test test_invalid_cron_does_not_fire ... ok
[INFO] [stdout] test test_every_n_minutes ... ok
[INFO] [stdout] test test_specific_minute ... ok
[INFO] [stdout] test test_add_and_list_entries ... ok
[INFO] [stdout] test test_persistence ... ok
[INFO] [stdout] test test_specific_hour_and_minute ... ok
[INFO] [stdout] test test_tick_fires_matching_entries ... ok
[INFO] [stdout] test test_replace_existing_entry ... ok
[INFO] [stdout] test test_tick_prevents_double_fire ... ok
[INFO] [stdout] test test_remove_entry ... ok
[INFO] [stdout] test test_drain_events ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_l10_autonomy.rs (/opt/rustwide/target/debug/deps/test_l10_autonomy-dceeab614aee06a8)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test test_l10_01_idle_detects_inbox_message ... ok
[INFO] [stdout] test test_l10_03_blocked_tasks_not_claimed ... ok
[INFO] [stdout] test test_l10_06_claim_task_nonexistent ... ok
[INFO] [stdout] test test_l10_05_identity_reinjection_after_compression ... ok
[INFO] [stdout] test test_l10_07_claim_already_claimed_task ... ok
[INFO] [stdout] test test_l10_08_claim_completed_task ... ok
[INFO] [stdout] test test_l10_10_scan_excludes_owned_tasks ... ok
[INFO] [stdout] test test_l10_02_auto_claims_unclaimed_unblocked_pending ... ok
[INFO] [stdout] test test_l10_11_scan_excludes_blocked_tasks ... ok
[INFO] [stdout] test test_l10_09_scan_excludes_non_pending ... ok
[INFO] [stdout] test test_l10_04_idle_timeout_triggers_shutdown ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.16s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_l11_isolation.rs (/opt/rustwide/target/debug/deps/test_l11_isolation-f1e8d290f8621785)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test_l11_06_name_validation_rejects_escape ... ok
[INFO] [stdout] test test_l11_01_create_produces_isolated_directory_and_branch ... ok
[INFO] [stdout] test test_l11_02_task_binding_is_bidirectional ... ok
[INFO] [stdout] test test_l11_04_remove_with_complete_task ... ok
[INFO] [stdout] test test_l11_05_events_emitted_for_lifecycle ... ok
[INFO] [stdout] test test_l11_03_binding_pending_task_advances_to_in_progress ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_l1_core_loop.rs (/opt/rustwide/target/debug/deps/test_l1_core_loop-450b1ef3c7d6bc94)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test test_l1_05_rejects_escape_attempts ... ok
[INFO] [stderr]      Running tests/test_l2_planning.rs (/opt/rustwide/target/debug/deps/test_l2_planning-7c91e0c72ec80515)
[INFO] [stdout] test test_l1_04_unknown_tool_returns_error_string ... ok
[INFO] [stdout] test test_l1_02_loop_continues_on_tool_use ... ok
[INFO] [stdout] test test_l1_06_allows_valid_relative_paths ... ok
[INFO] [stdout] test test_l1_03_dispatch_routes_to_correct_handler ... ok
[INFO] [stdout] test test_l1_07_tool_result_ids_match_tool_use_ids ... ok
[INFO] [stdout] test test_l1_01_loop_terminates_on_end_turn ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test_l2_02_allows_exactly_one_in_progress ... ok
[INFO] [stdout] test test_l2_05_nag_fires_after_threshold ... ok
[INFO] [stdout] test test_l2_06_nag_resets_when_todo_used ... ok
[INFO] [stdout] test test_l2_03_rejects_empty_content ... ok
[INFO] [stdout] test test_l2_04_rejects_invalid_status ... ok
[INFO] [stdout] test test_l2_01_rejects_two_in_progress ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stderr]      Running tests/test_l3_delegation.rs (/opt/rustwide/target/debug/deps/test_l3_delegation-5317a8175e29448d)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test test_l3_03_context_does_not_leak_to_parent ... ok
[INFO] [stdout] test test_l3_04_no_recursive_spawn_capability ... ok
[INFO] [stdout] test test_l3_01_subagent_starts_with_fresh_messages ... ok
[INFO] [stdout] test test_l3_07_subagent_llm_auth_error ... ok
[INFO] [stdout] test test_l3_02_returns_only_final_text ... ok
[INFO] [stdout] test test_l3_16_subagent_with_empty_prompt ... ok
[INFO] [stdout] test test_l3_17_subagent_with_small_success ... ok
[INFO] [stdout] test test_l3_12_subagent_zero_iterations ... ok
[INFO] [stdout] test test_l3_09_subagent_tool_validation_error ... ok
[INFO] [stdout] test test_l3_06_progress_callback_receives_delegation_phases ... ok
[INFO] [stdout] test test_l3_08_subagent_llm_overflow_error ... ok
[INFO] [stdout] test test_l3_05_respects_iteration_limit ... ok
[INFO] [stdout] test test_l3_14_event_bus_emits_progress_and_finish_on_success ... ok
[INFO] [stdout] test test_l3_15_tool_error_reported_in_progress ... ok
[INFO] [stdout] test test_l3_10_subagent_tool_execution_error ... ok
[INFO] [stdout] test test_l3_11_subagent_no_final_text_returns_placeholder ... ok
[INFO] [stdout] test test_l3_13_subagent_progress_callback_on_llm_error ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_l4_knowledge.rs (/opt/rustwide/target/debug/deps/test_l4_knowledge-15141b9baf844477)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test test_l4_02_load_returns_full_body_in_skill_tags ... ok
[INFO] [stdout] test test_l4_03_unknown_skill_returns_error_with_available_names ... ok
[INFO] [stdout] test test_l4_04_frontmatter_parsed_correctly ... ok
[INFO] [stdout] test test_l4_01_descriptions_returns_compact_metadata ... ok
[INFO] [stdout] test test_l4_05_system_prompt_uses_descriptions_not_bodies ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_l5_memory.rs (/opt/rustwide/target/debug/deps/test_l5_memory-56e3bd7f011ecc47)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test_l5_02_placeholders_not_empty ... ok
[INFO] [stdout] test test_l5_03_saves_transcript_before_replacing ... ok
[INFO] [stdout] test test_l5_04_auto_compact_returns_exactly_2_messages ... ok
[INFO] [stdout] test test_l5_01_preserves_last_n_tool_results ... ok
[INFO] [stdout] test test_l5_06_manual_compact_same_format_as_auto ... ok
[INFO] [stdout] test test_l5_05_triggers_at_token_threshold ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_l6_tasks.rs (/opt/rustwide/target/debug/deps/test_l6_tasks-9237a6b200794f6c)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test test_l6_01_task_ids_unique_and_incremental ... ok
[INFO] [stdout] test test_l6_03_invalid_status_raises_error ... ok
[INFO] [stdout] test test_l6_02_completing_task_clears_blocked_by ... ok
[INFO] [stdout] test test_l6_08_bind_worktree_nonexistent_task_fails ... ok
[INFO] [stdout] test test_l6_09_unbind_worktree_nonexistent_task_fails ... ok
[INFO] [stdout] test test_l6_07_exists_checks_file_existence ... ok
[INFO] [stdout] test test_l6_05_add_blocks_creates_bidirectional_edge ... ok
[INFO] [stdout] test test_l6_04_tasks_persist_as_json_files ... ok
[INFO] [stdout] test test_l6_06_get_nonexistent_task_returns_error ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_l7_concurrency.rs (/opt/rustwide/target/debug/deps/test_l7_concurrency-5cea7271de27bdd1)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test test_l7_01_run_returns_immediately ... ok
[INFO] [stdout] test test_l7_03_results_injected_before_llm_call ... ok
[INFO] [stdout] test test_l7_05_timeout_produces_error_status ... ok
[INFO] [stdout] test test_l7_04_captures_stdout_and_stderr ... ok
[INFO] [stdout] test test_l7_02_drain_is_atomic ... ok
[INFO] [stderr]      Running tests/test_l8_teams.rs (/opt/rustwide/target/debug/deps/test_l8_teams-8066143b08c81274)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.05s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test test_l8_05_teammate_gets_fresh_messages ... ok
[INFO] [stdout] test test_l8_04_spawn_creates_working_teammate ... ok
[INFO] [stdout] test test_l8_01_send_appends_jsonl_line ... ok
[INFO] [stdout] test test_l8_02_read_inbox_drains_and_clears ... ok
[INFO] [stdout] test test_l8_10_spawn_existing_with_idle_status_allows_reuse ... ok
[INFO] [stderr]      Running tests/test_l9_protocols.rs (/opt/rustwide/target/debug/deps/test_l9_protocols-c504de342d8a98c8)
[INFO] [stdout] test test_l8_06_broadcast_with_empty_teammates ... ok
[INFO] [stdout] test test_l8_03_broadcast_skips_sender ... ok
[INFO] [stdout] test test_l8_07_send_typed_with_extra_fields ... ok
[INFO] [stdout] test test_l8_08_shutdown_sets_status_and_signals ... ok
[INFO] [stdout] test test_l8_09_is_shutdown_requested ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test test_l9_03_rejection_transitions_to_rejected ... ok
[INFO] [stdout] test test_l9_04_plan_approval_sends_response ... ok
[INFO] [stdout] test test_l9_06_plan_review_unknown_request_id ... ok
[INFO] [stdout] test test_l9_05_correlation_by_request_id_concurrent ... ok
[INFO] [stdout] test test_l9_01_shutdown_request_creates_pending_entry ... ok
[INFO] [stdout] test test_l9_07_request_tracker_clear ... ok
[INFO] [stderr]      Running tests/test_lanes.rs (/opt/rustwide/target/debug/deps/test_lanes-c140bff50fb17cac)
[INFO] [stdout] test test_l9_02_approval_transitions_to_approved ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test test_run_in_lane_returns_immediately ... ok
[INFO] [stdout] test test_serial_ordering_in_main_lane ... ok
[INFO] [stdout] test test_parallel_in_background_lane ... ok
[INFO] [stdout] test test_unknown_lane_falls_back_to_background ... ok
[INFO] [stdout] test test_backwards_compat_run ... ok
[INFO] [stdout] test test_default_lanes_exist ... ok
[INFO] [stdout] test test_generation_rejection_on_reset ... ok
[INFO] [stdout] test test_drain_is_atomic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_llm_backends.rs (/opt/rustwide/target/debug/deps/test_llm_backends-9a4cd3f410f38ca7)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test_openai_outbound_simple_messages ... ok
[INFO] [stdout] test test_openai_outbound_tool_use_and_result ... ok
[INFO] [stdout] test test_openai_outbound_tools_format ... ok
[INFO] [stdout] test test_openai_inbound_text_response ... ok
[INFO] [stdout] test test_openai_inbound_tool_calls ... ok
[INFO] [stdout] test test_anthropic_message_serde_roundtrip ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_prompt.rs (/opt/rustwide/target/debug/deps/test_prompt-3a9c90e67e63b3a4)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test_empty_prompts_dir_gives_fallback ... ok
[INFO] [stdout] test test_missing_files_are_skipped ... ok
[INFO] [stdout] test test_placeholder_substitution ... ok
[INFO] [stdout] test test_init_defaults_does_not_overwrite_existing ... ok
[INFO] [stdout] test test_init_defaults_creates_files ... ok
[INFO] [stdout] test test_compose_with_default_files ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_resilience.rs (/opt/rustwide/target/debug/deps/test_resilience-10737819d34aebcf)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test_auth_rotation ... ok
[INFO] [stdout] test test_classify_error ... ok
[INFO] [stdout] test test_fatal_propagates_immediately ... ok
[INFO] [stdout] test test_overflow_propagates ... ok
[INFO] [stdout] test test_retry_on_transient_error ... ok
[INFO] [stdout] test test_max_attempts_exceeded ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/test_scenarios.rs (/opt/rustwide/target/debug/deps/test_scenarios-d1c1531eb2b62f2f)
[INFO] [stdout] 
[INFO] [stdout] running 30 tests
[INFO] [stdout] test scenario_01_loop_with_planning_nag ... ok
[INFO] [stdout] test scenario_03_skill_injection_into_system_prompt ... ok
[INFO] [stdout] test scenario_07_team_shutdown_protocol ... ok
[INFO] [stdout] test scenario_17_planning_with_task_tracking ... ok
[INFO] [stdout] test scenario_02_delegation_with_tool_use ... ok
[INFO] [stdout] test scenario_14_skill_informed_delegation ... ok
[INFO] [stdout] test scenario_15_multi_agent_task_queue ... ok
[INFO] [stdout] test scenario_04_memory_compaction_pipeline ... ok
[INFO] [stdout] test scenario_08_team_delegation ... ok
[INFO] [stdout] test scenario_18_token_estimation ... ok
[INFO] [stdout] test scenario_16_path_sandbox_edge_cases ... ok
[INFO] [stdout] test scenario_19_full_session_simulation ... ok
[INFO] [stdout] test scenario_22_overflow_triggers_compaction ... ok
[INFO] [stdout] test scenario_24_prompt_assembly_with_skills_and_todos ... ok
[INFO] [stdout] test scenario_21_resilience_retry_then_succeed ... ok
[INFO] [stdout] test scenario_10_plan_approval_flow ... ok
[INFO] [stdout] test scenario_23_fatal_error_stops_loop ... ok
[INFO] [stdout] test scenario_25_prompt_hot_reload ... ok
[INFO] [stdout] test scenario_09_autonomous_task_pickup ... ok
[INFO] [stdout] test scenario_11_long_session_with_compaction_and_planning ... ok
[INFO] [stdout] test scenario_29_resilient_subagent_delegation ... ok
[INFO] [stdout] test scenario_28_heartbeat_cron_lifecycle ... ok
[INFO] [stdout] test scenario_26_lane_concurrency_and_serialization ... ok
[INFO] [stdout] test scenario_30_full_new_features_integration ... ok
[INFO] [stdout] test scenario_13_worktree_safety_and_events ... FAILED
[INFO] [stdout] test scenario_05_task_lifecycle_with_worktree ... FAILED
[INFO] [stdout] test scenario_20_complete_project_workflow ... FAILED
[INFO] [stdout] test scenario_12_background_with_team_notification ... ok
[INFO] [stdout] test scenario_06_background_tasks_feed_loop ... ok
[INFO] [stdout] test scenario_27_lane_reset_cancels_queued ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- scenario_13_worktree_safety_and_events stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'scenario_13_worktree_safety_and_events' (341) panicked at tests/test_scenarios.rs:862:5:
[INFO] [stdout] assertion failed: result.is_ok()
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5f05612e34aa - std[ec4e911328de40a6]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5f05612e34aa - std[ec4e911328de40a6]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec4e911328de40a6]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5f05612e34aa - std[ec4e911328de40a6]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5f05612e34aa - <<std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a96a4c5c53f902a3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5f05612f8bda - <core[a96a4c5c53f902a3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5f05612f8bda - core[a96a4c5c53f902a3]::fmt::write
[INFO] [stdout]    6:     0x5f05612e8002 - std[ec4e911328de40a6]::io::default_write_fmt::<alloc[c960326de6de28e6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5f05612e8002 - <alloc[c960326de6de28e6]::vec::Vec<u8> as std[ec4e911328de40a6]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5f05612bfcaf - <std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5f05612bfcaf - std[ec4e911328de40a6]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5f05612da789 - std[ec4e911328de40a6]::panicking::default_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5f0560e9764c - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5f0560e9764c - test[30c43f9fd66fea90]::test_main_with_exit_callback::<test[30c43f9fd66fea90]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5f05612da942 - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5f05612da942 - std[ec4e911328de40a6]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5f05612bfd9a - std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x5f05612b7449 - std[ec4e911328de40a6]::sys::backtrace::__rust_end_short_backtrace::<std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5f05612c0a7d - __rustc[aa69f48705c57ca8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5f05612f941c - core[a96a4c5c53f902a3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5f05612f93e2 - core[a96a4c5c53f902a3]::panicking::panic
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:150:5
[INFO] [stdout]   20:     0x5f0560e707b9 - test_scenarios[89fb6ae908ed2daf]::scenario_13_worktree_safety_and_events
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_scenarios.rs:862:5
[INFO] [stdout]   21:     0x5f0560e59807 - test_scenarios[89fb6ae908ed2daf]::scenario_13_worktree_safety_and_events::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_scenarios.rs:834:44
[INFO] [stdout]   22:     0x5f0560e86366 - <test_scenarios[89fb6ae908ed2daf]::scenario_13_worktree_safety_and_events::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5f0560e8b72b - <fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5f0560e8b72b - test[30c43f9fd66fea90]::__rust_begin_short_backtrace::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:663:18
[INFO] [stdout]   25:     0x5f0560e9811b - test[30c43f9fd66fea90]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:74
[INFO] [stdout]   26:     0x5f0560e9811b - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   27:     0x5f0560e9811b - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   28:     0x5f0560e9811b - std[ec4e911328de40a6]::panicking::catch_unwind::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   29:     0x5f0560e9811b - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   30:     0x5f0560e9811b - test[30c43f9fd66fea90]::run_test_in_process
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:27
[INFO] [stdout]   31:     0x5f0560e9811b - test[30c43f9fd66fea90]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:607:43
[INFO] [stdout]   32:     0x5f0560e93834 - test[30c43f9fd66fea90]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:637:41
[INFO] [stdout]   33:     0x5f0560e93834 - std[ec4e911328de40a6]::sys::backtrace::__rust_begin_short_backtrace::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   34:     0x5f0560e9ad22 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   35:     0x5f0560e9ad22 - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   36:     0x5f0560e9ad22 - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   37:     0x5f0560e9ad22 - std[ec4e911328de40a6]::panicking::catch_unwind::<(), core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   38:     0x5f0560e9ad22 - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   39:     0x5f0560e9ad22 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   40:     0x5f0560e9ad22 - <std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x5f05612e259f - <alloc[c960326de6de28e6]::boxed::Box<dyn core[a96a4c5c53f902a3]::ops::function::FnOnce<(), Output = ()> + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   42:     0x5f05612e259f - <std[ec4e911328de40a6]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   43:     0x7c6bd8ab7aa4 - <unknown>
[INFO] [stdout]   44:     0x7c6bd8b44a64 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- scenario_05_task_lifecycle_with_worktree stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'scenario_05_task_lifecycle_with_worktree' (333) panicked at tests/test_scenarios.rs:389:5:
[INFO] [stdout] worktree creation should succeed
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5f05612e34aa - std[ec4e911328de40a6]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5f05612e34aa - std[ec4e911328de40a6]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec4e911328de40a6]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5f05612e34aa - std[ec4e911328de40a6]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5f05612e34aa - <<std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a96a4c5c53f902a3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5f05612f8bda - <core[a96a4c5c53f902a3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5f05612f8bda - core[a96a4c5c53f902a3]::fmt::write
[INFO] [stdout]    6:     0x5f05612e8002 - std[ec4e911328de40a6]::io::default_write_fmt::<alloc[c960326de6de28e6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5f05612e8002 - <alloc[c960326de6de28e6]::vec::Vec<u8> as std[ec4e911328de40a6]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5f05612bfcaf - <std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5f05612bfcaf - std[ec4e911328de40a6]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5f05612da789 - std[ec4e911328de40a6]::panicking::default_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5f0560e9764c - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5f0560e9764c - test[30c43f9fd66fea90]::test_main_with_exit_callback::<test[30c43f9fd66fea90]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5f05612da942 - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5f05612da942 - std[ec4e911328de40a6]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5f05612bfd9a - std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x5f05612b7449 - std[ec4e911328de40a6]::sys::backtrace::__rust_end_short_backtrace::<std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5f05612c0a7d - __rustc[aa69f48705c57ca8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5f05612f941c - core[a96a4c5c53f902a3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5f0560e7464c - test_scenarios[89fb6ae908ed2daf]::scenario_05_task_lifecycle_with_worktree
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_scenarios.rs:389:5
[INFO] [stdout]   20:     0x5f0560e59867 - test_scenarios[89fb6ae908ed2daf]::scenario_05_task_lifecycle_with_worktree::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_scenarios.rs:340:46
[INFO] [stdout]   21:     0x5f0560e863e6 - <test_scenarios[89fb6ae908ed2daf]::scenario_05_task_lifecycle_with_worktree::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5f0560e8b72b - <fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5f0560e8b72b - test[30c43f9fd66fea90]::__rust_begin_short_backtrace::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:663:18
[INFO] [stdout]   24:     0x5f0560e9811b - test[30c43f9fd66fea90]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:74
[INFO] [stdout]   25:     0x5f0560e9811b - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x5f0560e9811b - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   27:     0x5f0560e9811b - std[ec4e911328de40a6]::panicking::catch_unwind::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   28:     0x5f0560e9811b - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x5f0560e9811b - test[30c43f9fd66fea90]::run_test_in_process
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:27
[INFO] [stdout]   30:     0x5f0560e9811b - test[30c43f9fd66fea90]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:607:43
[INFO] [stdout]   31:     0x5f0560e93834 - test[30c43f9fd66fea90]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:637:41
[INFO] [stdout]   32:     0x5f0560e93834 - std[ec4e911328de40a6]::sys::backtrace::__rust_begin_short_backtrace::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x5f0560e9ad22 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   34:     0x5f0560e9ad22 - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x5f0560e9ad22 - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   36:     0x5f0560e9ad22 - std[ec4e911328de40a6]::panicking::catch_unwind::<(), core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   37:     0x5f0560e9ad22 - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x5f0560e9ad22 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   39:     0x5f0560e9ad22 - <std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x5f05612e259f - <alloc[c960326de6de28e6]::boxed::Box<dyn core[a96a4c5c53f902a3]::ops::function::FnOnce<(), Output = ()> + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   41:     0x5f05612e259f - <std[ec4e911328de40a6]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   42:     0x7c6bd8ab7aa4 - <unknown>
[INFO] [stdout]   43:     0x7c6bd8b44a64 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- scenario_20_complete_project_workflow stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'scenario_20_complete_project_workflow' (353) panicked at tests/test_scenarios.rs:1272:53:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: "fatal: invalid reference: HEAD"
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5f05612e34aa - std[ec4e911328de40a6]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5f05612e34aa - std[ec4e911328de40a6]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec4e911328de40a6]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5f05612e34aa - std[ec4e911328de40a6]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5f05612e34aa - <<std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a96a4c5c53f902a3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5f05612f8bda - <core[a96a4c5c53f902a3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5f05612f8bda - core[a96a4c5c53f902a3]::fmt::write
[INFO] [stdout]    6:     0x5f05612e8002 - std[ec4e911328de40a6]::io::default_write_fmt::<alloc[c960326de6de28e6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5f05612e8002 - <alloc[c960326de6de28e6]::vec::Vec<u8> as std[ec4e911328de40a6]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5f05612bfcaf - <std[ec4e911328de40a6]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5f05612bfcaf - std[ec4e911328de40a6]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5f05612da789 - std[ec4e911328de40a6]::panicking::default_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5f0560e9764c - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x5f0560e9764c - test[30c43f9fd66fea90]::test_main_with_exit_callback::<test[30c43f9fd66fea90]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5f05612da942 - <alloc[c960326de6de28e6]::boxed::Box<dyn for<'a, 'b> core[a96a4c5c53f902a3]::ops::function::Fn<(&'a std[ec4e911328de40a6]::panic::PanicHookInfo<'b>,), Output = ()> + core[a96a4c5c53f902a3]::marker::Sync + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::Fn<(&std[ec4e911328de40a6]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x5f05612da942 - std[ec4e911328de40a6]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5f05612bfd68 - std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5f05612b7449 - std[ec4e911328de40a6]::sys::backtrace::__rust_end_short_backtrace::<std[ec4e911328de40a6]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5f05612c0a7d - __rustc[aa69f48705c57ca8]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5f05612f941c - core[a96a4c5c53f902a3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5f05612f9192 - core[a96a4c5c53f902a3]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5f0560e69b88 - <core[a96a4c5c53f902a3]::result::Result<alloc[c960326de6de28e6]::string::String, alloc[c960326de6de28e6]::boxed::Box<dyn core[a96a4c5c53f902a3]::error::Error>>>::unwrap
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x5f0560e69b88 - test_scenarios[89fb6ae908ed2daf]::scenario_20_complete_project_workflow
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_scenarios.rs:1272:53
[INFO] [stdout]   22:     0x5f0560e59747 - test_scenarios[89fb6ae908ed2daf]::scenario_20_complete_project_workflow::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_scenarios.rs:1240:43
[INFO] [stdout]   23:     0x5f0560e86266 - <test_scenarios[89fb6ae908ed2daf]::scenario_20_complete_project_workflow::{closure#0} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5f0560e8b72b - <fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5f0560e8b72b - test[30c43f9fd66fea90]::__rust_begin_short_backtrace::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, fn() -> core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:663:18
[INFO] [stdout]   26:     0x5f0560e9811b - test[30c43f9fd66fea90]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:74
[INFO] [stdout]   27:     0x5f0560e9811b - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x5f0560e9811b - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x5f0560e9811b - std[ec4e911328de40a6]::panicking::catch_unwind::<core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>, core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5f0560e9811b - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<test[30c43f9fd66fea90]::run_test_in_process::{closure#0}>, core[a96a4c5c53f902a3]::result::Result<(), alloc[c960326de6de28e6]::string::String>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5f0560e9811b - test[30c43f9fd66fea90]::run_test_in_process
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:686:27
[INFO] [stdout]   32:     0x5f0560e9811b - test[30c43f9fd66fea90]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:607:43
[INFO] [stdout]   33:     0x5f0560e93834 - test[30c43f9fd66fea90]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/test/src/lib.rs:637:41
[INFO] [stdout]   34:     0x5f0560e93834 - std[ec4e911328de40a6]::sys::backtrace::__rust_begin_short_backtrace::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x5f0560e9ad22 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   36:     0x5f0560e9ad22 - <core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x5f0560e9ad22 - std[ec4e911328de40a6]::panicking::catch_unwind::do_call::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x5f0560e9ad22 - std[ec4e911328de40a6]::panicking::catch_unwind::<(), core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5f0560e9ad22 - std[ec4e911328de40a6]::panic::catch_unwind::<core[a96a4c5c53f902a3]::panic::unwind_safe::AssertUnwindSafe<std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5f0560e9ad22 - std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked::<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   41:     0x5f0560e9ad22 - <std[ec4e911328de40a6]::thread::lifecycle::spawn_unchecked<test[30c43f9fd66fea90]::run_test::{closure#1}, ()>::{closure#1} as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5f05612e259f - <alloc[c960326de6de28e6]::boxed::Box<dyn core[a96a4c5c53f902a3]::ops::function::FnOnce<(), Output = ()> + core[a96a4c5c53f902a3]::marker::Send> as core[a96a4c5c53f902a3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   43:     0x5f05612e259f - <std[ec4e911328de40a6]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/0abe69e6c16114311cc2700e972492e64d69c28f/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x7c6bd8ab7aa4 - <unknown>
[INFO] [stdout]   45:     0x7c6bd8b44a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     scenario_05_task_lifecycle_with_worktree
[INFO] [stdout]     scenario_13_worktree_safety_and_events
[INFO] [stdout]     scenario_20_complete_project_workflow
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 27 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.83s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test test_scenarios`
[INFO] running `Command { std: "docker" "inspect" "1c7a743a68c117484967c14ec22e4cf1c309faeb621416bc475379e19a2bed40", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1c7a743a68c117484967c14ec22e4cf1c309faeb621416bc475379e19a2bed40", kill_on_drop: false }`
[INFO] [stdout] 1c7a743a68c117484967c14ec22e4cf1c309faeb621416bc475379e19a2bed40
